[INFO] fetching crate seesaw_core 0.26.3...
[INFO] testing seesaw_core-0.26.3 against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] extracting crate seesaw_core 0.26.3 into /workspace/builds/worker-2-tc1/source
[INFO] started tweaking crates.io crate seesaw_core 0.26.3
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate seesaw_core 0.26.3
[INFO] tweaked toml for crates.io crate seesaw_core 0.26.3 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate seesaw_core 0.26.3 on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate seesaw_core 0.26.3 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded seesaw_core_macros v0.26.3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41
[INFO] running `Command { std: "docker" "start" "854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-w" "/opt/rustwide/workdir" "--user" "0:0" "854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling sha1_smol v1.0.1
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling seesaw_core_macros v0.26.3
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling uuid v1.22.0
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling seesaw_core v0.26.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 8.58s
[INFO] running `Command { std: "docker" "inspect" "854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-w" "/opt/rustwide/workdir" "--user" "0:0" "854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling target-triple v1.0.0
[INFO] [stderr]    Compiling serde_spanned v1.0.4
[INFO] [stderr]    Compiling toml_datetime v1.0.0+spec-1.1.0
[INFO] [stderr]    Compiling uuid v1.22.0
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling toml_parser v1.0.9+spec-1.1.0
[INFO] [stderr]    Compiling toml v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling seesaw_core v0.26.3 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling trybuild v1.0.116
[INFO] [stdout] warning: struct `EnrichmentResult` is never constructed
[INFO] [stdout]    --> tests/macro_api.rs:531:8
[INFO] [stdout]     |
[INFO] [stdout] 531 | struct EnrichmentResult {
[INFO] [stdout]     |        ^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused variable: `handle`
[INFO] [stdout]     --> tests/engine_integration.rs:2383:9
[INFO] [stdout]      |
[INFO] [stdout] 2383 |     let handle = engine
[INFO] [stdout]      |         ^^^^^^ help: if this is intentional, prefix it with an underscore: `_handle`
[INFO] [stdout]      |
[INFO] [stdout]      = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused variable: `handle`
[INFO] [stdout]     --> tests/engine_integration.rs:2430:9
[INFO] [stdout]      |
[INFO] [stdout] 2430 |     let handle = engine
[INFO] [stdout]      |         ^^^^^^ help: if this is intentional, prefix it with an underscore: `_handle`
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `transient` is never read
[INFO] [stdout]     --> tests/engine_integration.rs:3358:9
[INFO] [stdout]      |
[INFO] [stdout] 3355 |     struct FailSkip {
[INFO] [stdout]      |            -------- field in this struct
[INFO] [stdout] ...
[INFO] [stdout] 3358 |         transient: String,
[INFO] [stdout]      |         ^^^^^^^^^
[INFO] [stdout]      |
[INFO] [stdout]      = note: `FailSkip` has derived impls for the traits `Clone` and `Debug`, but these are intentionally ignored during dead code analysis
[INFO] [stdout]      = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `secret_data` is never read
[INFO] [stdout]     --> tests/engine_integration.rs:3415:9
[INFO] [stdout]      |
[INFO] [stdout] 3411 |     struct ExtractEvent {
[INFO] [stdout]      |            ------------ field in this struct
[INFO] [stdout] ...
[INFO] [stdout] 3415 |         secret_data: Vec<u8>,
[INFO] [stdout]      |         ^^^^^^^^^^^
[INFO] [stdout]      |
[INFO] [stdout]      = note: `ExtractEvent` has derived impls for the traits `Clone` and `Debug`, but these are intentionally ignored during dead code analysis
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: struct `ExpansionTriggered` is never constructed
[INFO] [stdout]     --> tests/engine_integration.rs:3613:8
[INFO] [stdout]      |
[INFO] [stdout] 3613 | struct ExpansionTriggered;
[INFO] [stdout]      |        ^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: struct `Enriched` is never constructed
[INFO] [stdout]     --> tests/engine_integration.rs:3735:8
[INFO] [stdout]      |
[INFO] [stdout] 3735 | struct Enriched {
[INFO] [stdout]      |        ^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 26.20s
[INFO] running `Command { std: "docker" "inspect" "854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-w" "/opt/rustwide/workdir" "--user" "0:0" "854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: unused variable: `handle`
[INFO] [stderr]     --> tests/engine_integration.rs:2383:9
[INFO] [stderr]      |
[INFO] [stderr] 2383 |     let handle = engine
[INFO] [stderr]      |         ^^^^^^ help: if this is intentional, prefix it with an underscore: `_handle`
[INFO] [stderr]      |
[INFO] [stderr]      = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused variable: `handle`
[INFO] [stderr]     --> tests/engine_integration.rs:2430:9
[INFO] [stderr]      |
[INFO] [stderr] 2430 |     let handle = engine
[INFO] [stderr]      |         ^^^^^^ help: if this is intentional, prefix it with an underscore: `_handle`
[INFO] [stderr] 
[INFO] [stderr] warning: field `transient` is never read
[INFO] [stderr]     --> tests/engine_integration.rs:3358:9
[INFO] [stderr]      |
[INFO] [stderr] 3355 |     struct FailSkip {
[INFO] [stderr]      |            -------- field in this struct
[INFO] [stderr] ...
[INFO] [stderr] 3358 |         transient: String,
[INFO] [stderr]      |         ^^^^^^^^^
[INFO] [stderr]      |
[INFO] [stderr]      = note: `FailSkip` has derived impls for the traits `Clone` and `Debug`, but these are intentionally ignored during dead code analysis
[INFO] [stderr]      = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: field `secret_data` is never read
[INFO] [stderr]     --> tests/engine_integration.rs:3415:9
[INFO] [stderr]      |
[INFO] [stderr] 3411 |     struct ExtractEvent {
[INFO] [stderr]      |            ------------ field in this struct
[INFO] [stderr] ...
[INFO] [stderr] 3415 |         secret_data: Vec<u8>,
[INFO] [stderr]      |         ^^^^^^^^^^^
[INFO] [stderr]      |
[INFO] [stderr]      = note: `ExtractEvent` has derived impls for the traits `Clone` and `Debug`, but these are intentionally ignored during dead code analysis
[INFO] [stderr] 
[INFO] [stderr] warning: struct `ExpansionTriggered` is never constructed
[INFO] [stderr]     --> tests/engine_integration.rs:3613:8
[INFO] [stderr]      |
[INFO] [stderr] 3613 | struct ExpansionTriggered;
[INFO] [stderr]      |        ^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: struct `Enriched` is never constructed
[INFO] [stderr]     --> tests/engine_integration.rs:3735:8
[INFO] [stderr]      |
[INFO] [stderr] 3735 | struct Enriched {
[INFO] [stderr]      |        ^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: `seesaw_core` (test "engine_integration") generated 6 warnings (run `cargo fix --test "engine_integration" -p seesaw_core` to apply 2 suggestions)
[INFO] [stderr] warning: struct `EnrichmentResult` is never constructed
[INFO] [stderr]    --> tests/macro_api.rs:531:8
[INFO] [stderr]     |
[INFO] [stderr] 531 | struct EnrichmentResult {
[INFO] [stderr]     |        ^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `seesaw_core` (test "macro_api") generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.18s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/seesaw_core-576a05ab4d9d6d6d)
[INFO] [stdout] running 63 tests
[INFO] [stdout] test event_store::tests::event_type_short_name_extracts_last_segment ... ok
[INFO] [stdout] test event_store::tests::causal_metadata_round_trips ... ok
[INFO] [stdout] test event_store::tests::idempotent_append_returns_existing_position ... ok
[INFO] [stdout] test event_store::tests::append_and_load_aggregate_events ... ok
[INFO] [stdout] test event_store::tests::empty_stream_returns_empty ... ok
[INFO] [stdout] test event_store::tests::load_global_from_empty_when_caught_up ... ok
[INFO] [stdout] test event_store::tests::empty_metadata_round_trips ... ok
[INFO] [stdout] test event_store::tests::load_global_from_respects_limit ... ok
[INFO] [stdout] test event_store::tests::load_stream_from_filters_by_version ... ok
[INFO] [stdout] test event_store::tests::position_increments_globally ... ok
[INFO] [stdout] test event_store::tests::non_aggregate_event_is_persisted ... ok
[INFO] [stdout] test event_store::tests::metadata_round_trips ... ok
[INFO] [stdout] test handler::builders::tests::filter_does_not_force_queued_execution ... ok
[INFO] [stdout] test handler::context::tests::ctx_run_executes_closure_and_returns_result ... ok
[INFO] [stdout] test event_store::tests::streams_are_isolated ... ok
[INFO] [stdout] test handler::context::tests::journal_replay_empty_vec ... ok
[INFO] [stdout] test handler::context::tests::ctx_run_propagates_errors ... ok
[INFO] [stdout] test handler::context::tests::ctx_run_multiple_calls_return_independent_results ... ok
[INFO] [stdout] test handler::context::tests::ctx_run_unit_return ... ok
[INFO] [stdout] test handler::context::tests::journal_clear_on_resolve_complete ... ok
[INFO] [stdout] test handler::context::tests::journal_cloned_context_shares_sequence_counter ... ok
[INFO] [stdout] test handler::context::tests::journal_crash_retry_simulation ... ok
[INFO] [stdout] test handler::context::tests::journal_error_not_journaled_allows_retry ... ok
[INFO] [stdout] test handler::context::tests::journal_error_midway_preserves_prior_entries ... ok
[INFO] [stdout] test handler::context::tests::journal_replay_many_then_continue ... ok
[INFO] [stdout] test handler::context::tests::journal_error_then_retry_replays_and_continues ... ok
[INFO] [stdout] test handler::context::tests::journal_isolation_by_event_id ... ok
[INFO] [stdout] test handler::context::tests::journal_isolation_by_handler_id ... ok
[INFO] [stdout] test handler::context::tests::journal_many_sequential_runs ... ok
[INFO] [stdout] test handler::context::tests::journal_preserved_on_retry_resolution ... ok
[INFO] [stdout] test event_store::tests::load_global_from_returns_events_after_position ... ok
[INFO] [stdout] test handler::context::tests::journal_persists_new_entries_to_store ... ok
[INFO] [stdout] test handler::context::tests::journal_replay_with_gap_in_seq_from_prior_error ... ok
[INFO] [stdout] test handler::context::tests::journal_replay_unit_type ... ok
[INFO] [stdout] test handler::context::tests::journal_replay_with_option_none ... ok
[INFO] [stdout] test handler::context::tests::journal_replay_with_complex_struct ... ok
[INFO] [stdout] test handler::context::tests::journal_replays_multiple_entries_in_sequence ... ok
[INFO] [stdout] test handler::context::tests::journal_sorts_unsorted_entries_on_load ... ok
[INFO] [stdout] test handler::context::tests::test_effect_context_accessors ... ok
[INFO] [stdout] test handler::error_event::tests::test_clone ... ok
[INFO] [stdout] test handler::context::tests::test_effect_context_clone ... ok
[INFO] [stdout] test handler::error_event::tests::test_downcast_failure ... ok
[INFO] [stdout] test handler::context::tests::journal_replay_with_option_some ... ok
[INFO] [stdout] test handler::error_event::tests::test_downcast_success ... ok
[INFO] [stdout] test handler::context::tests::journal_replays_then_executes_fresh_after_journal_exhausted ... ok
[INFO] [stdout] test handler::context::tests::journal_replays_single_entry_without_executing ... ok
[INFO] [stdout] test handler::types::tests::test_any_event_downcast ... ok
[INFO] [stdout] test handler::types::tests::test_event_output_debug ... ok
[INFO] [stdout] test handler::context::tests::journal_seq_counter_advances_even_on_error ... ok
[INFO] [stdout] test handler_registry::tests::test_handler_can_handle ... ok
[INFO] [stdout] test handler_registry::tests::test_group_handler ... ok
[INFO] [stdout] test handler_registry::tests::test_multiple_handlers ... ok
[INFO] [stdout] test handler_registry::tests::test_handler_registry_registers_handlers ... ok
[INFO] [stdout] test upcaster::tests::upcast_chain_v1_to_v3 ... ok
[INFO] [stdout] test handler::types::tests::test_emit_conversions ... ok
[INFO] [stdout] test upcaster::tests::upcast_noop_for_unknown_event ... ok
[INFO] [stdout] test upcaster::tests::upcast_noop_when_current ... ok
[INFO] [stdout] test upcaster::tests::upcast_error_propagates ... ok
[INFO] [stdout] test handler_registry::tests::test_register_rejects_generated_id_for_queued_handler - should panic ... ok
[INFO] [stdout] test handler::error_event::tests::test_handler_error_creation ... ok
[INFO] [stdout] test handler::error_event::tests::test_debug_formatting ... ok
[INFO] [stdout] test handler::context::tests::journal_type_mismatch_on_replay_returns_error ... ok
[INFO] [stdout] test handler_registry::tests::test_register_rejects_duplicate_handler_ids - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 63 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/compile_fail.rs (/opt/rustwide/target/debug/deps/compile_fail-8d98365466a1c674)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]     Checking pin-project-lite v0.2.17
[INFO] [stderr]     Checking cfg-if v1.0.4
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]     Checking futures-sink v0.3.32
[INFO] [stderr]     Checking memchr v2.8.0
[INFO] [stderr]    Compiling autocfg v1.5.0
[INFO] [stderr]     Checking futures-core v0.3.32
[INFO] [stderr]     Checking futures-task v0.3.32
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]     Checking once_cell v1.21.3
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]     Checking slab v0.4.12
[INFO] [stderr]     Checking futures-io v0.3.32
[INFO] [stderr]    Compiling zmij v1.0.21
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]     Checking futures-channel v0.3.32
[INFO] [stderr]     Checking smallvec v1.15.1
[INFO] [stderr]     Checking scopeguard v1.2.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling anyhow v1.0.102
[INFO] [stderr]     Checking tracing-core v0.1.36
[INFO] [stderr]     Checking iana-time-zone v0.1.65
[INFO] [stderr]     Checking lock_api v0.4.14
[INFO] [stderr]     Checking hashbrown v0.14.5
[INFO] [stderr]     Checking sha1_smol v1.0.1
[INFO] [stderr]     Checking itoa v1.0.17
[INFO] [stderr]     Checking fastrand v2.3.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]     Checking parking_lot v0.12.5
[INFO] [stderr]     Checking dashmap v6.1.0
[INFO] [stderr]     Checking uuid v1.22.0
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling seesaw_core_macros v0.26.3
[INFO] [stderr]     Checking futures-util v0.3.32
[INFO] [stderr]     Checking tokio v1.50.0
[INFO] [stderr]     Checking tracing v0.1.44
[INFO] [stderr]     Checking chrono v0.4.44
[INFO] [stderr]     Checking futures-executor v0.3.32
[INFO] [stderr]     Checking futures v0.3.32
[INFO] [stderr]     Checking seesaw_core v0.26.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Checking seesaw_core-tests v0.0.0 (/opt/rustwide/target/tests/trybuild/seesaw_core)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 22.18s
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stderr] test tests/compile_fail/event_enum_no_prefix.rs ... ok
[INFO] [stderr] test tests/compile_fail/event_enum_no_serde_tag.rs ... ok
[INFO] [stderr] test tests/compile_fail/event_enum_untagged.rs ... ok
[INFO] [stderr] test tests/compile_fail/filter_extract_exclusive.rs ... ok
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stdout] test compile_fail ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 32.66s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/engine_integration.rs (/opt/rustwide/target/debug/deps/engine_integration-67be7f9401684037)
[INFO] [stdout] 
[INFO] [stdout] running 115 tests
[INFO] [stdout] test auto_persist_events_per_aggregate_stream ... ok
[INFO] [stdout] test cancel_does_not_affect_other_correlations ... ok
[INFO] [stdout] test cancel_dlqs_pending_handlers ... ok
[INFO] [stdout] test cancel_is_idempotent ... ok
[INFO] [stdout] test auto_snapshot_every_n_events ... ok
[INFO] [stdout] test cancel_prevents_event_processing ... ok
[INFO] [stdout] test auto_snapshot_hydration_uses_checkpoint ... ok
[INFO] [stdout] test cancel_mid_settle_rejects_downstream_events ... ok
[INFO] [stdout] test basic_handler_fires ... ok
[INFO] [stdout] test chained_ephemeral_to_ephemeral_to_persistent ... ok
[INFO] [stdout] test correlation_preserved_through_queued_chain ... ok
[INFO] [stdout] test ctx_run_executes_side_effect_in_handler ... ok
[INFO] [stdout] test cold_start_hydration ... ok
[INFO] [stdout] test cross_aggregate_event_persisted_to_multiple_streams ... ok
[INFO] [stdout] test describe_fires_for_ephemeral_events ... ok
[INFO] [stdout] test custom_correlation_id_propagates_to_child_events ... ok
[INFO] [stdout] test dlq_terminal_event_from_ephemeral_triggered_handler ... ok
[INFO] [stdout] test deep_ephemeral_chain_with_hop_counting ... ok
[INFO] [stdout] test cancel_correlation_with_ephemeral_events ... ok
[INFO] [stdout] test engine_status_delegates_to_store ... ok
[INFO] [stdout] test ephemeral_available_in_projection ... ok
[INFO] [stdout] test dlq_terminal_preserves_correlation ... ok
[INFO] [stdout] test ephemeral_available_via_on_any_handler ... ok
[INFO] [stdout] test ephemeral_does_not_break_dlq_path ... ok
[INFO] [stdout] test custom_correlation_id_on_process_handle ... ok
[INFO] [stdout] test emit_requires_settled ... ok
[INFO] [stdout] test engine_without_event_store_identical_behavior ... ok
[INFO] [stdout] test describe_reflects_post_handler_aggregate_state ... ok
[INFO] [stdout] test dlq_event_carries_failed_handler_id_in_metadata ... ok
[INFO] [stdout] test ephemeral_event_not_in_store ... ok
[INFO] [stdout] test ephemeral_only_root_event_still_settles ... ok
[INFO] [stdout] test ephemeral_persistent_flag_propagates_through_causal_chain ... ok
[INFO] [stdout] test ephemeral_events_excluded_from_aggregate_streams ... ok
[INFO] [stdout] test ephemeral_event_handler_emits_persistent ... ok
[INFO] [stdout] test ephemeral_event_with_retry_handler ... ok
[INFO] [stdout] test ephemeral_events_advance_checkpoint ... ok
[INFO] [stdout] test ephemeral_preserved_in_handler_chain ... ok
[INFO] [stdout] test ephemeral_event_does_not_run_projections ... ok
[INFO] [stdout] test engine_without_on_dlq_unchanged ... ok
[INFO] [stdout] test ephemeral_preserves_serde_skip_fields ... ok
[INFO] [stdout] test ephemeral_event_hop_counting ... ok
[INFO] [stdout] test ephemeral_event_idempotent_append ... ok
[INFO] [stdout] test empty_aggregate_access_returns_default ... ok
[INFO] [stdout] test handler_emits_chain ... ok
[INFO] [stdout] test ephemeral_preserved_in_batch_fanout ... ok
[INFO] [stdout] test ephemeral_event_does_not_apply_to_aggregates ... ok
[INFO] [stdout] test ephemeral_root_event_not_persisted ... ok
[INFO] [stdout] test ephemeral_shared_across_multiple_handlers ... ok
[INFO] [stdout] test ephemeral_triggers_on_any_handler ... ok
[INFO] [stdout] test ephemeral_with_extract_handler ... ok
[INFO] [stdout] test handler_returns_nothing ... ok
[INFO] [stdout] test event_metadata_on_non_aggregate_events ... ok
[INFO] [stdout] test event_metadata_stamped_on_persisted_events ... ok
[INFO] [stdout] test events_without_aggregator_not_in_stream ... ok
[INFO] [stdout] test journal_cleared_after_successful_handler ... ok
[INFO] [stdout] test journal_isolation_across_different_events ... ok
[INFO] [stdout] test ephemeral_root_via_emit_output ... ok
[INFO] [stdout] test invalidate_aggregate_forces_rehydration ... ok
[INFO] [stdout] test journal_concurrent_ctx_run_from_spawned_tasks ... ok
[INFO] [stdout] test missing_snapshot_falls_back_to_full_replay ... ok
[INFO] [stdout] test journal_large_nested_payload_round_trips ... ok
[INFO] [stdout] test interleaved_persistent_ephemeral_batch_ordering ... ok
[INFO] [stdout] test journal_replays_completed_run_calls_on_retry ... ok
[INFO] [stdout] test mixed_aggregate_types_independent_streams ... ok
[INFO] [stdout] test ephemeral_via_emit_output ... ok
[INFO] [stdout] test mixed_persistent_and_ephemeral_in_same_batch ... ok
[INFO] [stdout] test multiple_ephemeral_root_events ... ok
[INFO] [stdout] test multi_type_handler_with_filter_fires_on_both_types ... ok
[INFO] [stdout] test multi_type_handler_fires_on_both_event_types ... ok
[INFO] [stdout] test multiple_handlers_same_event ... ok
[INFO] [stdout] test omitted_correlation_id_auto_generates ... ok
[INFO] [stdout] test no_snapshot_without_threshold ... ok
[INFO] [stdout] test no_metadata_produces_empty_map ... ok
[INFO] [stdout] test on_dlq_not_called_on_success ... ok
[INFO] [stdout] test handler_panic_does_not_crash_engine ... ok
[INFO] [stdout] test journal_works_for_ephemeral_event_handlers ... ok
[INFO] [stdout] test dlq_terminal_event_published ... ok
[INFO] [stdout] test on_dlq_per_handler_on_failure_takes_precedence ... ok
[INFO] [stdout] test on_dlq_fires_on_custom_error_type ... ok
[INFO] [stdout] test on_dlq_fires_after_all_retries_exhausted ... ok
[INFO] [stdout] test process_handle_cancel_works ... ok
[INFO] [stdout] test projection_error_does_not_stop_other_handlers ... ok
[INFO] [stdout] test on_dlq_fires_on_queued_handler_panic ... ok
[INFO] [stdout] test on_dlq_preserves_correlation_id ... ok
[INFO] [stdout] test projection_error_recorded_as_projection_failure ... ok
[INFO] [stdout] test on_dlq_fires_on_anyhow_bail ... ok
[INFO] [stdout] test on_dlq_not_fired_when_retry_succeeds ... ok
[INFO] [stdout] test journal_panic_mid_execution_preserves_prior_entries ... ok
[INFO] [stdout] test on_dlq_mapper_error_does_not_crash_engine ... ok
[INFO] [stdout] test on_dlq_receives_correct_info ... ok
[INFO] [stdout] test queue_status_empty_after_settle ... ok
[INFO] [stdout] test on_dlq_emits_event_on_handler_failure ... ok
[INFO] [stdout] test queue_status_shows_pending_before_settle ... ok
[INFO] [stdout] test on_dlq_fires_for_each_failing_handler ... ok
[INFO] [stdout] test on_failure_with_source_event_access ... ok
[INFO] [stdout] test queued_handler_executes ... ok
[INFO] [stdout] test retry_succeeds_on_second_attempt ... ok
[INFO] [stdout] test reclaimed_handler_sees_hydrated_aggregate_state ... ok
[INFO] [stdout] test save_snapshot_helper_works ... ok
[INFO] [stdout] test sequential_events_same_aggregate_correct_versions ... ok
[INFO] [stdout] test settled_timeout_succeeds_when_fast ... ok
[INFO] [stdout] test singleton_hydrated_across_event_types_for_handler_filter ... ok
[INFO] [stdout] test upcaster_chain_in_aggregate_replay ... ok
[INFO] [stdout] test snapshot_acceleration ... ok
[INFO] [stdout] test transition_guard_works_after_cold_start ... ok
[INFO] [stdout] test snapshot_at_version_prevents_immediate_re_snapshot ... ok
[INFO] [stdout] test rapid_fire_events_same_aggregate ... ok
[INFO] [stdout] test upcaster_transforms_old_event_for_handler ... ok
[INFO] [stdout] test snapshot_not_triggered_by_ephemeral_events ... ok
[INFO] [stdout] test stale_snapshot_partial_replay_fills_gap ... ok
[INFO] [stdout] test on_dlq_event_handler_failure_does_not_cascade ... ok
[INFO] [stdout] test large_event_replay_produces_correct_state ... ok
[INFO] [stdout] test backoff_retries_handler_with_exponential_delay ... FAILED
[INFO] [stdout] test settled_timeout_errors_when_slow ... ok
[INFO] [stdout] test on_dlq_fires_on_timeout ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- backoff_retries_handler_with_exponential_delay stdout ----
[INFO] [stdout] Handler sent to DLQ: a5bb137e-4a8d-4098-8ee2-bfc2f9686ffa:backoff_ping - always fails (attempts: 3)
[INFO] [stdout] 
[INFO] [stdout] thread 'backoff_retries_handler_with_exponential_delay' (2057) panicked at tests/engine_integration.rs:692:5:
[INFO] [stdout] second retry gap (41.519327ms) should be longer than first (169.610749ms)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x59bf6b576e88 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x59bf6b576e88 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x59bf6b576e88 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x59bf6b576e88 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x59bf6b58bafa - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x59bf6b58bafa - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x59bf6b57bbd2 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x59bf6b57bbd2 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x59bf6b5556c6 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x59bf6b5556c6 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x59bf6b56f109 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x59bf6b3f2bdc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x59bf6b3f2bdc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x59bf6b56f382 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x59bf6b56f382 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x59bf6b555782 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x59bf6b54a429 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x59bf6b55658d - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x59bf6b58c2bc - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x59bf6b18b5e5 - engine_integration[2dd4280d72ddc877]::backoff_retries_handler_with_exponential_delay::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/engine_integration.rs:692:5
[INFO] [stdout]   20:     0x59bf6b38ed65 - <core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   21:     0x59bf6b38ee5d - <core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   22:     0x59bf6b3e22bd - <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:769:70
[INFO] [stdout]   23:     0x59bf6b3e1fcc - tokio[3eeaf7cfff1006e2]::task::coop::with_budget::<core[28b146924c2f19c6]::task::poll::Poll<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>, <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   24:     0x59bf6b3e1fcc - tokio[3eeaf7cfff1006e2]::task::coop::budget::<core[28b146924c2f19c6]::task::poll::Poll<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>, <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   25:     0x59bf6b3e1fcc - <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:769:25
[INFO] [stdout]   26:     0x59bf6b3d6dda - <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::Context>::enter::<core[28b146924c2f19c6]::task::poll::Poll<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>, <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:446:19
[INFO] [stdout]   27:     0x59bf6b3e02cd - <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:768:44
[INFO] [stdout]   28:     0x59bf6b3dea3b - <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:856:68
[INFO] [stdout]   29:     0x59bf6b36ac0a - <tokio[3eeaf7cfff1006e2]::runtime::context::scoped::Scoped<tokio[3eeaf7cfff1006e2]::runtime::scheduler::Context>>::set::<<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   30:     0x59bf6b393f0a - tokio[3eeaf7cfff1006e2]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>), <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context.rs:176:38
[INFO] [stdout]   31:     0x59bf6b3b98f6 - <std[929523f510218772]::thread::local::LocalKey<tokio[3eeaf7cfff1006e2]::runtime::context::Context>>::try_with::<tokio[3eeaf7cfff1006e2]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>), <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   32:     0x59bf6b3b919c - <std[929523f510218772]::thread::local::LocalKey<tokio[3eeaf7cfff1006e2]::runtime::context::Context>>::with::<tokio[3eeaf7cfff1006e2]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>), <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   33:     0x59bf6b391c31 - tokio[3eeaf7cfff1006e2]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>), <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context.rs:176:17
[INFO] [stdout]   34:     0x59bf6b3db539 - <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:856:27
[INFO] [stdout]   35:     0x59bf6b3dbd06 - <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:756:24
[INFO] [stdout]   36:     0x59bf6b3ddde9 - <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:200:33
[INFO] [stdout]   37:     0x59bf6b37b263 - tokio[3eeaf7cfff1006e2]::runtime::context::runtime::enter_runtime::<<tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>::{closure#0}, core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   38:     0x59bf6b3d6241 - <tokio[3eeaf7cfff1006e2]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:188:9
[INFO] [stdout]   39:     0x59bf6b3d5a4f - <tokio[3eeaf7cfff1006e2]::runtime::runtime::Runtime>::block_on_inner::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/runtime.rs:371:52
[INFO] [stdout]   40:     0x59bf6b3d5d73 - <tokio[3eeaf7cfff1006e2]::runtime::runtime::Runtime>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/runtime.rs:345:18
[INFO] [stdout]   41:     0x59bf6b1a5cd3 - engine_integration[2dd4280d72ddc877]::backoff_retries_handler_with_exponential_delay
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/engine_integration.rs:699:7
[INFO] [stdout]   42:     0x59bf6b1436e7 - engine_integration[2dd4280d72ddc877]::backoff_retries_handler_with_exponential_delay::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/engine_integration.rs:652:62
[INFO] [stdout]   43:     0x59bf6b2c1ba6 - <engine_integration[2dd4280d72ddc877]::backoff_retries_handler_with_exponential_delay::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   44:     0x59bf6b3e5cbb - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x59bf6b3e5cbb - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   46:     0x59bf6b3f3535 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   47:     0x59bf6b3f3535 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x59bf6b3f3535 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x59bf6b3f3535 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x59bf6b3f3535 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x59bf6b3f3535 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   52:     0x59bf6b3f3535 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   53:     0x59bf6b3ee094 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   54:     0x59bf6b3ee094 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   55:     0x59bf6b3f65b2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   56:     0x59bf6b3f65b2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   57:     0x59bf6b3f65b2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   58:     0x59bf6b3f65b2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   59:     0x59bf6b3f65b2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   60:     0x59bf6b3f65b2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   61:     0x59bf6b3f65b2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   62:     0x59bf6b5767af - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   63:     0x59bf6b5767af - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   64:     0x7158a3e0aaa4 - <unknown>
[INFO] [stdout]   65:     0x7158a3e97a64 - clone
[INFO] [stdout]   66:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     backoff_retries_handler_with_exponential_delay
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 114 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.05s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test engine_integration`
[INFO] running `Command { std: "docker" "inspect" "854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41", kill_on_drop: false }`
[INFO] [stdout] 854db457f45d5606ff6403a187c7de88157851a56e1b30002fa6d763ea414f41
